Feature: Book Genie Mode - Automated Testing with Detailed Reporting
File name: BookGenie.feature
Relative path: features/BookGenie.feature
Scenario Outline: Book Genie Response Validation with Citation Verification
When
I open the Creative Workspace login page
00:00:03.005
Then
I should see the homepage
00:00:00.036
When
I click on the mode selection dropdown
00:00:04.159
Then
I can see the "BookGenieQA" mode
00:00:00.036
And
I select the "BookGenieQA" mode
00:00:02.544
Then
I type "Suggest 5 books on christmas" on chat input element
00:00:31.217
And
I wait for AI to complete thinking
00:00:07.825
And
I validate the response is visible for "Suggest 5 books on christmas"
00:00:00.008
And
I extract book data from BookGenie response
00:00:00.011
And
I save BookGenie book data to Excel file for query "Suggest 5 books on christmas"
00:00:00.000
+ Show Error
Error: No book data extracted from BookGenie response.
at CustomWorld.(anonymous) (/home/runner/work/HC_Epub_Automation/HC_Epub_Automation/tests/steps/bookGenie.step.ts:46:15)
at /home/runner/work/HC_Epub_Automation/HC_Epub_Automation/tests/steps/bookGenie.step.ts:8:71
at __awaiter (/home/runner/work/HC_Epub_Automation/HC_Epub_Automation/tests/steps/bookGenie.step.ts:4:12)
at CustomWorld.(anonymous) (/home/runner/work/HC_Epub_Automation/HC_Epub_Automation/tests/steps/bookGenie.step.ts:59:12)
And
I validate each book individually against Excel file for query "Suggest 5 books on christmas"
0s
And
I validate that reason texts match citation texts with 80% similarity
0s
And
I generate detailed citation validation report
0s
Then
I verify database connectivity
0s
And
database should contain 10032 books
0s
And
I validate extracted books individually against database
0s
And
I validate BookGenie response relevance with AI for query "Suggest 5 books on christmas"
0s
=== SCENARIO LOGS: Book Genie Response Validation with Citation Verification === [12:46:33 PM] Navigating to Creative Workspace login page [12:46:37 PM] Homepage visibility: false [12:46:37 PM] ℹ️ Clicking on mode selection dropdown [12:46:41 PM] ✅ Mode selection dropdown clicked successfully [12:46:41 PM] ℹ️ Checking visibility of mode: BookGenieQA [12:46:41 PM] ✅ Mode "BookGenieQA" is visible [12:46:41 PM] ℹ️ Selecting mode: BookGenieQA [12:46:41 PM] ✅ Mode "BookGenieQA" selected successfully [12:46:43 PM] ℹ️ Waiting for mode transition to complete [12:46:43 PM] ℹ️ Typing query: "Suggest 5 books on christmas" [12:47:13 PM] ⚠ ⚠Book Genie mode did not loaded [12:47:13 PM] ℹ️ Preparing to type query: "Suggest 5 books on christmas" [12:47:13 PM] ℹ️ Waiting for chat input to be visible [12:47:13 PM] ℹ️ Clearing chat input [12:47:13 PM] ℹ️ Typing query: "Suggest 5 books on christmas" [12:47:14 PM] ℹ️ Pressing Enter to submit query [12:47:14 PM] ✅ Query submitted successfully: "Suggest 5 books on christmas" [12:47:14 PM] ℹ️ Waiting for AI to complete thinking... [12:47:15 PM] 📋 WAITING FOR AI RESPONSE [12:47:15 PM] ℹ️ Looking for AI thinking indicator... [12:47:15 PM] ℹ️ Waiting for thinking indicator to appear (max 2 minutes) [12:47:16 PM] ✅ ✓ AI thinking indicator appeared - AI is processing the request [12:47:16 PM] ℹ️ Waiting for thinking indicator to disappear (max 5 minutes) [12:47:19 PM] ✅ ✓ AI thinking completed - Response should be ready [12:47:19 PM] ℹ️ 🔍 Checking for "None of the above, just" option... [12:47:19 PM] ℹ️ ✓ "None of the above, just" option not present - proceeding normally [12:47:19 PM] ℹ️ Allowing additional time for response rendering [12:47:22 PM] ✅ AI response wait process completed [12:47:22 PM] ℹ️ Validating response for query: "Suggest 5 books on christmas" [12:47:22 PM] 📋 VALIDATING RESPONSE FOR QUERY: "Suggest 5 books on christmas" [12:47:22 PM] ℹ️ Locating the latest response in chat [12:47:22 PM] ℹ️ Waiting for response to be visible (max 30 seconds) [12:47:22 PM] ✅ Response received - Length: 583 characters [12:47:22 PM] ℹ️ Response preview: Watch Me WorkInterpreting context for your query...That sounds like a lovely theme! To help you find... [12:47:22 PM] ℹ️ Extracting book data from response... [12:47:22 PM] 📋 EXTRACTING BOOK DATA FROM RESPONSE [12:47:22 PM] ℹ️ Locating BookGenie response container [12:47:22 PM] ℹ️ Extracting HTML content from response [12:47:22 PM] ℹ️ HTML content extracted - Length: 8245 characters [12:47:22 PM] ℹ️ Parsing HTML to extract book data [12:47:22 PM] ✅ SUCCESSFULLY EXTRACTED 0 BOOKS [12:47:22 PM] 📋 EXTRACTED BOOKS SUMMARY [12:47:22 PM] 📊 Total books extracted: 0 [12:47:22 PM] 📊 Average relevance score: 0% [12:47:22 PM] ✅ Extracted 0 books from response === END LOGS ===
Scenario Logs: Book Genie Response Validation with Citation Verification
[12:46:33 PM] Navigating to Creative Workspace login page
[12:46:37 PM] Homepage visibility: false
[12:46:37 PM] ℹ️ Clicking on mode selection dropdown
[12:46:41 PM] ✅ Mode selection dropdown clicked successfully
[12:46:41 PM] ℹ️ Checking visibility of mode: BookGenieQA
[12:46:41 PM] ✅ Mode "BookGenieQA" is visible
[12:46:41 PM] ℹ️ Selecting mode: BookGenieQA
[12:46:41 PM] ✅ Mode "BookGenieQA" selected successfully
[12:46:43 PM] ℹ️ Waiting for mode transition to complete
[12:46:43 PM] ℹ️ Typing query: "Suggest 5 books on christmas"
[12:47:13 PM] ⚠ ⚠Book Genie mode did not loaded
[12:47:13 PM] ℹ️ Preparing to type query: "Suggest 5 books on christmas"
[12:47:13 PM] ℹ️ Waiting for chat input to be visible
[12:47:13 PM] ℹ️ Clearing chat input
[12:47:13 PM] ℹ️ Typing query: "Suggest 5 books on christmas"
[12:47:14 PM] ℹ️ Pressing Enter to submit query
[12:47:14 PM] ✅ Query submitted successfully: "Suggest 5 books on christmas"
[12:47:14 PM] ℹ️ Waiting for AI to complete thinking...
[12:47:15 PM] 📋 WAITING FOR AI RESPONSE
[12:47:15 PM] ℹ️ Looking for AI thinking indicator...
[12:47:15 PM] ℹ️ Waiting for thinking indicator to appear (max 2 minutes)
[12:47:16 PM] ✅ ✓ AI thinking indicator appeared - AI is processing the request
[12:47:16 PM] ℹ️ Waiting for thinking indicator to disappear (max 5 minutes)
[12:47:19 PM] ✅ ✓ AI thinking completed - Response should be ready
[12:47:19 PM] ℹ️ 🔍 Checking for "None of the above, just" option...
[12:47:19 PM] ℹ️ ✓ "None of the above, just" option not present - proceeding normally
[12:47:19 PM] ℹ️ Allowing additional time for response rendering
[12:47:22 PM] ✅ AI response wait process completed
[12:47:22 PM] ℹ️ Validating response for query: "Suggest 5 books on christmas"
[12:47:22 PM] 📋 VALIDATING RESPONSE FOR QUERY: "Suggest 5 books on christmas"
[12:47:22 PM] ℹ️ Locating the latest response in chat
[12:47:22 PM] ℹ️ Waiting for response to be visible (max 30 seconds)
[12:47:22 PM] ✅ Response received - Length: 583 characters
[12:47:22 PM] ℹ️ Response preview: Watch Me WorkInterpreting context for your query...That sounds like a lovely theme! To help you find...
[12:47:22 PM] ℹ️ Extracting book data from response...
[12:47:22 PM] 📋 EXTRACTING BOOK DATA FROM RESPONSE
[12:47:22 PM] ℹ️ Locating BookGenie response container
[12:47:22 PM] ℹ️ Extracting HTML content from response
[12:47:22 PM] ℹ️ HTML content extracted - Length: 8245 characters
[12:47:22 PM] ℹ️ Parsing HTML to extract book data
[12:47:22 PM] ✅ SUCCESSFULLY EXTRACTED 0 BOOKS
[12:47:22 PM] 📋 EXTRACTED BOOKS SUMMARY
[12:47:22 PM] 📊 Total books extracted: 0
[12:47:22 PM] 📊 Average relevance score: 0%
[12:47:22 PM] ✅ Extracted 0 books from response
Scenario Outline: Book Genie Response Validation with Citation Verification
When
I open the Creative Workspace login page
00:00:03.128
Then
I should see the homepage
00:00:00.036
When
I click on the mode selection dropdown
00:00:03.660
Then
I can see the "BookGenieQA" mode
00:00:00.057
And
I select the "BookGenieQA" mode
00:00:02.284
Then
I type "Suggest 3 books for childrens" on chat input element
00:00:31.220
And
I wait for AI to complete thinking
00:00:18.145
And
I validate the response is visible for "Suggest 3 books for childrens"
00:00:00.009
And
I extract book data from BookGenie response
00:00:00.015
And
I save BookGenie book data to Excel file for query "Suggest 3 books for childrens"
00:00:00.010
And
I validate each book individually against Excel file for query "Suggest 3 books for childrens"
00:00:00.014
CITATION VALIDATION DETAILED REPORT ================================================================================ BOOK: A Christmas Carol ------------------------------------------------------------ ✅ Reason 1: PASS (85%) ✅ Reason 2: PASS (98%) ✅ Reason 3: PASS (95%) BOOK: A Series of Unfortunate Events #1: The Bad Beginning ------------------------------------------------------------ ✅ Reason 1: PASS (100%) ❌ Reason 2: FAIL (0%) 📝 Reason: The book is suitable for children aged 8 to 12... 📚 Citation: Interest age, years: from 8 to 12... ⚠️ Citation text not found in reason: "Interest age, years: from 8 to 12" ❌ Reason 3: FAIL (0%) 📝 Reason: The book is a mix of adventure, humor, and mystery... 📚 Citation: children... ⚠️ Citation text not found in reason: "children" BOOK: Replay ------------------------------------------------------------ ❌ Reason 1: FAIL (0%) 📝 Reason: The book is about twelve-year-old Leo, who has a talent for transforming the ordinary into the extra... 📚 Citation: a story about twelve-year-old Leo, who has a talent for transforming the ordinary into the extraordi... ⚠️ Citation text not found in reason: "a story about twelve-year-old Leo, who has a talent for transforming the ordinary into the extraordinary" ❌ Reason 2: FAIL (0%) 📝 Reason: The book is suitable for children aged 8 to 12... 📚 Citation: Interest age, years: from 8 to 12... ⚠️ Citation text not found in reason: "Interest age, years: from 8 to 12" ❌ Reason 3: FAIL (0%) 📝 Reason: The book has the universal theme of finding one's true identity, and set amid a loud, noisy, memorab... 📚 Citation: With the universal theme of finding one's true identity, and set amid a loud, noisy, memorable famil... ⚠️ Citation text not found in reason: "With the universal theme of finding one's true identity, and set amid a loud, noisy, memorable family" SUMMARY: 4/9 reasons passed (44.4%) OVERALL STATUS: FAIL
Citation validation failed: 4/9 passed (44.4%)
Citation Validation Summary: 4/9 passed
📊 Citation Validation Report
Generated on 10/21/2025, 12:51:01 PM
📖 A Christmas Carol
✅ Reason 1: 85% match
✅ Reason 2: 98% match
✅ Reason 3: 95% match
📖 A Series of Unfortunate Events #1: The Bad Beginning
✅ Reason 1: 100% match
❌ Reason 2: 0% match
⚠️ Citation text not found in reason: "Interest age, years: from 8 to 12"
❌ Reason 3: 0% match
⚠️ Citation text not found in reason: "children"
📖 Replay
❌ Reason 1: 0% match
⚠️ Citation text not found in reason: "a story about twelve-year-old Leo, who has a talent for transforming the ordinary into the extraordinary"
❌ Reason 2: 0% match
⚠️ Citation text not found in reason: "Interest age, years: from 8 to 12"
❌ Reason 3: 0% match
⚠️ Citation text not found in reason: "With the universal theme of finding one's true identity, and set amid a loud, noisy, memorable family"
📈 Validation Summary
Total Reasons Validated: 9
Passed: 4
Failed: 5
Pass Rate: 44.4%
Overall Status: FAIL
Then
I verify database connectivity
00:00:00.349
And
database should contain 10032 books
00:00:00.247
And
I validate extracted books individually against database
00:00:00.479
And
I validate BookGenie response relevance with AI for query "Suggest 3 books for childrens"
00:00:06.240
+ Show Info
+ Show Info
PER-BOOK AI VALIDATION REPORT - Query: "Suggest 3 books for childrens"
================================================================================
OVERALL RELEVANCE SCORE: 70%
STATUS: FAIL
BOOKS ANALYZED: 3
BOOK 1: "A Christmas Carol"
------------------------------------------------------------
Overall Score: 70%
SECTION SCORES:
Author Information: 70% - Analysis unavailable - API error
Publishing Date: 70% - Analysis unavailable - API error
Why Match Explanations: 70% - Analysis unavailable - API error
Relevance Scores: 70% - Analysis unavailable - API error
DETAILED FEEDBACK:
• AI analysis temporarily unavailable due to service overload
IMPROVEMENT SUGGESTIONS:
• Retry analysis when service is available
================================================================================
BOOK 2: "A Series of Unfortunate Events #1: The Bad Beginning"
------------------------------------------------------------
Overall Score: 70%
SECTION SCORES:
Author Information: 70% - Analysis unavailable - API error
Publishing Date: 70% - Analysis unavailable - API error
Why Match Explanations: 70% - Analysis unavailable - API error
Relevance Scores: 70% - Analysis unavailable - API error
DETAILED FEEDBACK:
• AI analysis temporarily unavailable due to service overload
IMPROVEMENT SUGGESTIONS:
• Retry analysis when service is available
================================================================================
BOOK 3: "Replay"
------------------------------------------------------------
Overall Score: 70%
SECTION SCORES:
Author Information: 70% - Analysis unavailable - API error
Publishing Date: 70% - Analysis unavailable - API error
Why Match Explanations: 70% - Analysis unavailable - API error
Relevance Scores: 70% - Analysis unavailable - API error
DETAILED FEEDBACK:
• AI analysis temporarily unavailable due to service overload
IMPROVEMENT SUGGESTIONS:
• Retry analysis when service is available
================================================================================
SUMMARY FEEDBACK:
----------------------------------------
• AI analysis service is temporarily overloaded
• Using fallback analysis with neutral scores
• Error: [GoogleGenerativeAI Error]: Error fetching from https://generativelanguage.googleapis.com/v1beta/models/gemini-2.5-pro:generateContent: [429 Too Many Requests] You exceeded your current quota, please check your plan and billing details. For more information on this error, head to: https://ai.google.dev/gemini-api/docs/rate-limits.
* Quota exceeded for metric: generativelanguage.googleapis.com/generate_content_free_tier_requests, limit: 2
Please retry in 51.171089048s. [{"@type":"type.googleapis.com/google.rpc.QuotaFailure","violations":[{"quotaMetric":"generativelanguage.googleapis.com/generate_content_free_tier_requests","quotaId":"GenerateRequestsPerMinutePerProjectPerModel-FreeTier","quotaDimensions":{"model":"gemini-2.5-pro","location":"global"},"quotaValue":"2"}]},{"@type":"type.googleapis.com/google.rpc.Help","links":[{"description":"Learn more about Gemini API quotas","url":"https://ai.google.dev/gemini-api/docs/rate-limits"}]},{"@type":"type.googleapis.com/google.rpc.RetryInfo","retryDelay":"51s"}]
OVERALL IMPROVEMENT SUGGESTIONS:
----------------------------------------
• Retry the analysis when Gemini API is less busy
• Consider running tests during off-peak hours
• Check Google AI status page for service updates
🤖 PER-BOOK AI Response Relevance Validation
Query: "Suggest 3 books for childrens"
Overall Relevance Score: 70%
Status: FAIL
Books Analyzed: 3
Individual Book Analyses
📖 Book 1: "A Christmas Carol" - 70%
Author Information: 70%
Analysis unavailable - API error
Publishing Date: 70%
Analysis unavailable - API error
Why Match Explanations: 70%
Analysis unavailable - API error
Relevance Scores: 70%
Analysis unavailable - API error
Improvement Suggestions:
💡 Retry analysis when service is available
📖 Book 2: "A Series of Unfortunate Events #1: The Bad Beginning" - 70%
Author Information: 70%
Analysis unavailable - API error
Publishing Date: 70%
Analysis unavailable - API error
Why Match Explanations: 70%
Analysis unavailable - API error
Relevance Scores: 70%
Analysis unavailable - API error
Improvement Suggestions:
💡 Retry analysis when service is available
📖 Book 3: "Replay" - 70%
Author Information: 70%
Analysis unavailable - API error
Publishing Date: 70%
Analysis unavailable - API error
Why Match Explanations: 70%
Analysis unavailable - API error
Relevance Scores: 70%
Analysis unavailable - API error
Improvement Suggestions:
💡 Retry analysis when service is available
Summary Feedback
• AI analysis service is temporarily overloaded
• Using fallback analysis with neutral scores
• Error: [GoogleGenerativeAI Error]: Error fetching from https://generativelanguage.googleapis.com/v1beta/models/gemini-2.5-pro:generateContent: [429 Too Many Requests] You exceeded your current quota, please check your plan and billing details. For more information on this error, head to: https://ai.google.dev/gemini-api/docs/rate-limits. * Quota exceeded for metric: generativelanguage.googleapis.com/generate_content_free_tier_requests, limit: 2 Please retry in 51.171089048s. [{"@type":"type.googleapis.com/google.rpc.QuotaFailure","violations":[{"quotaMetric":"generativelanguage.googleapis.com/generate_content_free_tier_requests","quotaId":"GenerateRequestsPerMinutePerProjectPerModel-FreeTier","quotaDimensions":{"model":"gemini-2.5-pro","location":"global"},"quotaValue":"2"}]},{"@type":"type.googleapis.com/google.rpc.Help","links":[{"description":"Learn more about Gemini API quotas","url":"https://ai.google.dev/gemini-api/docs/rate-limits"}]},{"@type":"type.googleapis.com/google.rpc.RetryInfo","retryDelay":"51s"}]
Overall Improvement Suggestions
💡 Retry the analysis when Gemini API is less busy
💡 Consider running tests during off-peak hours
💡 Check Google AI status page for service updates
=== SCENARIO LOGS: Book Genie Response Validation with Citation Verification ===
[12:47:32 PM] Navigating to Creative Workspace login page
[12:47:36 PM] Homepage visibility: false
[12:47:36 PM] ℹ️ Clicking on mode selection dropdown
[12:47:39 PM] ✅ Mode selection dropdown clicked successfully
[12:47:39 PM] ℹ️ Checking visibility of mode: BookGenieQA
[12:47:39 PM] ✅ Mode "BookGenieQA" is visible
[12:47:39 PM] ℹ️ Selecting mode: BookGenieQA
[12:47:40 PM] ✅ Mode "BookGenieQA" selected successfully
[12:47:42 PM] ℹ️ Waiting for mode transition to complete
[12:47:42 PM] ℹ️ Typing query: "Suggest 3 books for childrens"
[12:48:12 PM] ⚠ ⚠Book Genie mode did not loaded
[12:48:12 PM] ℹ️ Preparing to type query: "Suggest 3 books for childrens"
[12:48:12 PM] ℹ️ Waiting for chat input to be visible
[12:48:12 PM] ℹ️ Clearing chat input
[12:48:12 PM] ℹ️ Typing query: "Suggest 3 books for childrens"
[12:48:13 PM] ℹ️ Pressing Enter to submit query
[12:48:13 PM] ✅ Query submitted successfully: "Suggest 3 books for childrens"
[12:48:13 PM] ℹ️ Waiting for AI to complete thinking...
[12:48:13 PM] 📋 WAITING FOR AI RESPONSE
[12:48:13 PM] ℹ️ Looking for AI thinking indicator...
[12:48:13 PM] ℹ️ Waiting for thinking indicator to appear (max 2 minutes)
[12:48:15 PM] ✅ ✓ AI thinking indicator appeared - AI is processing the request
[12:48:15 PM] ℹ️ Waiting for thinking indicator to disappear (max 5 minutes)
[12:48:27 PM] ✅ ✓ AI thinking completed - Response should be ready
[12:48:27 PM] ℹ️ 🔍 Checking for "None of the above, just" option...
[12:48:28 PM] ℹ️ ✓ "None of the above, just" option not present - proceeding normally
[12:48:28 PM] ℹ️ Allowing additional time for response rendering
[12:48:31 PM] ✅ AI response wait process completed
[12:48:31 PM] ℹ️ Validating response for query: "Suggest 3 books for childrens"
[12:48:31 PM] 📋 VALIDATING RESPONSE FOR QUERY: "Suggest 3 books for childrens"
[12:48:31 PM] ℹ️ Locating the latest response in chat
[12:48:31 PM] ℹ️ Waiting for response to be visible (max 30 seconds)
[12:48:31 PM] ✅ Response received - Length: 2233 characters
[12:48:31 PM] ℹ️ Response preview: Watch Me WorkRetrieving Relevant Books for 'books for children' in Book Summary ...Retrieving Releva...
[12:48:31 PM] ℹ️ Extracting book data from response...
[12:48:31 PM] 📋 EXTRACTING BOOK DATA FROM RESPONSE
[12:48:31 PM] ℹ️ Locating BookGenie response container
[12:48:31 PM] ℹ️ Extracting HTML content from response
[12:48:31 PM] ℹ️ HTML content extracted - Length: 22514 characters
[12:48:31 PM] ℹ️ Parsing HTML to extract book data
[12:48:31 PM] ✅ SUCCESSFULLY EXTRACTED 3 BOOKS
[12:48:31 PM] 📋 EXTRACTED BOOKS SUMMARY
[12:48:31 PM] 📚 1. "A Christmas Carol" - Score: 100%%
[12:48:31 PM] 📚 2. "A Series of Unfortunate Events #1: The Bad Beginning" - Score: 100%%
[12:48:31 PM] 📚 3. "Replay" - Score: 100%%
[12:48:31 PM] 📊 Total books extracted: 3
[12:48:31 PM] 📊 Average relevance score: 100%
[12:48:31 PM] ✅ Extracted 3 books from response
[12:48:31 PM] ℹ️ Saving 3 books to Excel file: test_results/suggest_3_books_for_childrens.xlsx
[12:48:31 PM] 📋 SAVING BOOKS TO EXCEL FILE
[12:48:31 PM] ℹ️ Target file path: test_results/suggest_3_books_for_childrens.xlsx
[12:48:31 PM] ℹ️ Exporting 3 books to Excel format
[12:48:31 PM] ✅ Book data saved to: test_results/suggest_3_books_for_childrens.xlsx
[12:48:31 PM] 📊 File verified - Size: 17.07 KB
[12:48:31 PM] ✅ ✓ Excel file creation verified successfully
[12:48:31 PM] 📋 STARTING INDIVIDUAL BOOK VALIDATION (3 books) for query: "Suggest 3 books for childrens"
[12:48:31 PM] 📋 📚 VALIDATING BOOK 1: "A Christmas Carol"
[12:48:31 PM] ℹ️ Starting comprehensive book validation
[12:48:31 PM] ℹ️ Validating title: "A Christmas Carol" vs expected: "A Christmas Carol"
[12:48:31 PM] ✅ ✅ TITLE VALIDATION PASSED: "A Christmas Carol"
[12:48:31 PM] ℹ️ Validating score: 100% vs expected: 100%
[12:48:31 PM] ✅ ✅ SCORE VALIDATION PASSED: 100% (expected 100%)
[12:48:31 PM] ✅ ✅ PERFECT SCORE GAP VALIDATION: Book has 100% score and no gap mentioned
[12:48:31 PM] ℹ️ Why-match points: extracted 3 vs expected 3
[12:48:31 PM] ✅ ✅ WHY-MATCH VALIDATION PASSED: 3 points (expected 3)
[12:48:31 PM] ✅ 🎉 BOOK 1 VALIDATION: ALL CHECKS PASSED!
[12:48:31 PM] ℹ️ Book 1 validation completed
[12:48:31 PM] 📋 📚 VALIDATING BOOK 2: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:48:31 PM] ℹ️ Starting comprehensive book validation
[12:48:31 PM] ℹ️ Validating title: "A Series of Unfortunate Events #1: The Bad Beginning" vs expected: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:48:31 PM] ✅ ✅ TITLE VALIDATION PASSED: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:48:31 PM] ℹ️ Validating score: 100% vs expected: 100%
[12:48:31 PM] ✅ ✅ SCORE VALIDATION PASSED: 100% (expected 100%)
[12:48:31 PM] ✅ ✅ PERFECT SCORE GAP VALIDATION: Book has 100% score and no gap mentioned
[12:48:31 PM] ℹ️ Why-match points: extracted 3 vs expected 3
[12:48:31 PM] ✅ ✅ WHY-MATCH VALIDATION PASSED: 3 points (expected 3)
[12:48:31 PM] ✅ 🎉 BOOK 2 VALIDATION: ALL CHECKS PASSED!
[12:48:31 PM] ℹ️ Book 2 validation completed
[12:48:31 PM] 📋 📚 VALIDATING BOOK 3: "Replay"
[12:48:31 PM] ℹ️ Starting comprehensive book validation
[12:48:31 PM] ℹ️ Validating title: "Replay" vs expected: "Replay"
[12:48:31 PM] ✅ ✅ TITLE VALIDATION PASSED: "Replay"
[12:48:31 PM] ℹ️ Validating score: 100% vs expected: 100%
[12:48:31 PM] ✅ ✅ SCORE VALIDATION PASSED: 100% (expected 100%)
[12:48:31 PM] ✅ ✅ PERFECT SCORE GAP VALIDATION: Book has 100% score and no gap mentioned
[12:48:31 PM] ℹ️ Why-match points: extracted 3 vs expected 3
[12:48:31 PM] ✅ ✅ WHY-MATCH VALIDATION PASSED: 3 points (expected 3)
[12:48:31 PM] ✅ 🎉 BOOK 3 VALIDATION: ALL CHECKS PASSED!
[12:48:31 PM] ℹ️ Book 3 validation completed
[12:48:31 PM] 📊 VALIDATION SUMMARY: 3/3 books passed
[12:48:31 PM] ✅ ALL BOOKS VALIDATED SUCCESSFULLY!
[12:48:31 PM] 📋 🔍 VALIDATING REASON-CITATION MATCHES
[12:48:31 PM] ℹ️ Starting citation validation process...
[12:48:31 PM] 📋 🚀 STARTING COMPLETE CITATION VALIDATION WORKFLOW
[12:48:31 PM] ℹ️ Step 1: Extracting books from AI response
[12:48:31 PM] 📋 EXTRACTING BOOK DATA FROM RESPONSE
[12:48:31 PM] ℹ️ Locating BookGenie response container
[12:48:31 PM] ℹ️ Extracting HTML content from response
[12:48:31 PM] ℹ️ HTML content extracted - Length: 22514 characters
[12:48:31 PM] ℹ️ Parsing HTML to extract book data
[12:48:31 PM] ✅ SUCCESSFULLY EXTRACTED 3 BOOKS
[12:48:31 PM] 📋 EXTRACTED BOOKS SUMMARY
[12:48:31 PM] 📚 1. "A Christmas Carol" - Score: 100%%
[12:48:31 PM] 📚 2. "A Series of Unfortunate Events #1: The Bad Beginning" - Score: 100%%
[12:48:31 PM] 📚 3. "Replay" - Score: 100%%
[12:48:31 PM] 📊 Total books extracted: 3
[12:48:31 PM] 📊 Average relevance score: 100%
[12:48:31 PM] ℹ️ Step 2: Extracting citation texts from book elements
[12:48:31 PM] 📋 📚 EXTRACTING CITATION TEXTS FROM BOOK ELEMENTS
[12:48:31 PM] ℹ️ Step 1: Waiting for main book section
[12:48:31 PM] ℹ️ Found 14 total accordion sections
[12:48:31 PM] ℹ️ Found individual book: "1. A Christmas Carol"
[12:48:31 PM] ℹ️ Found individual book: "2. A Series of Unfortunate Events #1: The Bad Beginning"
[12:48:31 PM] ℹ️ Found individual book: "3. Replay"
[12:48:31 PM] ℹ️ Found individual book: "1. A Christmas Carol"
[12:48:31 PM] ℹ️ Found individual book: "2. A Series of Unfortunate Events #1: The Bad Beginning"
[12:48:31 PM] ℹ️ Found individual book: "3. Replay"
[12:48:31 PM] ℹ️ Processing 6 individual book sections
[12:48:31 PM] 📋 📖 PROCESSING BOOK 1: "A Christmas Carol"
[12:48:31 PM] ℹ️ Expanding book section: "A Christmas Carol"
[12:48:34 PM] ✅ Book section expanded: "A Christmas Carol"
[12:48:34 PM] ℹ️ Expanding "Why this book is the match" section for: "A Christmas Carol"
[12:48:38 PM] ✅ "Why this book is the match" section expanded for: "A Christmas Carol"
[12:48:38 PM] ℹ️ Found 3 citation buttons for "A Christmas Carol"
[12:48:38 PM] ℹ️ Extracting citation 1/3 for "A Christmas Carol"
[12:48:38 PM] ℹ️ Processing metadata citation 1 for "A Christmas Carol"
[12:48:39 PM] ℹ️ Clicking to OPEN metadata citation 1
[12:48:41 PM] ℹ️ Extracted citation text (first 100 chars): christmas books...
[12:48:41 PM] ℹ️ Clicking to CLOSE metadata citation 1
[12:48:42 PM] ✅ ✅ metadata citation 1 closed successfully
[12:48:42 PM] ℹ️ ✅ Citation 1 extracted successfully (length: 15)
[12:48:42 PM] ℹ️ Extracting citation 2/3 for "A Christmas Carol"
[12:48:42 PM] ℹ️ Processing metadata citation 2 for "A Christmas Carol"
[12:48:43 PM] ℹ️ Clicking to OPEN metadata citation 2
[12:48:45 PM] ℹ️ Extracted citation text (first 100 chars): But four ghostly visitors come to show him a different way, opening Scrooge’s heart to kindness and ...
[12:48:45 PM] ℹ️ Clicking to CLOSE metadata citation 2
[12:48:47 PM] ✅ ✅ metadata citation 2 closed successfully
[12:48:47 PM] ℹ️ ✅ Citation 2 extracted successfully (length: 175)
[12:48:47 PM] ℹ️ Extracting citation 3/3 for "A Christmas Carol"
[12:48:47 PM] ℹ️ Processing metadata citation 3 for "A Christmas Carol"
[12:48:48 PM] ℹ️ Clicking to OPEN metadata citation 3
[12:48:50 PM] ℹ️ Extracted citation text (first 100 chars): Interest age, years: from 6 to 10...
[12:48:50 PM] ℹ️ Clicking to CLOSE metadata citation 3
[12:48:51 PM] ✅ ✅ metadata citation 3 closed successfully
[12:48:51 PM] ℹ️ ✅ Citation 3 extracted successfully (length: 33)
[12:48:51 PM] ℹ️ Collapsing book section: "A Christmas Carol"
[12:48:53 PM] ✅ Book section collapsed: "A Christmas Carol"
[12:48:53 PM] ✅ ✅ Completed processing "A Christmas Carol" - 3 citations extracted
[12:48:53 PM] 📋 📖 PROCESSING BOOK 2: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:48:53 PM] ℹ️ Expanding book section: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:48:56 PM] ✅ Book section expanded: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:48:56 PM] ℹ️ Expanding "Why this book is the match" section for: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:48:59 PM] ✅ "Why this book is the match" section expanded for: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:48:59 PM] ℹ️ Found 3 citation buttons for "A Series of Unfortunate Events #1: The Bad Beginning"
[12:48:59 PM] ℹ️ Extracting citation 1/3 for "A Series of Unfortunate Events #1: The Bad Beginning"
[12:48:59 PM] ℹ️ Processing metadata citation 1 for "A Series of Unfortunate Events #1: The Bad Beginning"
[12:49:00 PM] ℹ️ Clicking to OPEN metadata citation 1
[12:49:02 PM] ℹ️ Extracted citation text (first 100 chars): a tale of three likeable and unfortunate children...
[12:49:02 PM] ℹ️ Clicking to CLOSE metadata citation 1
[12:49:04 PM] ✅ ✅ metadata citation 1 closed successfully
[12:49:04 PM] ℹ️ ✅ Citation 1 extracted successfully (length: 49)
[12:49:04 PM] ℹ️ Extracting citation 2/3 for "A Series of Unfortunate Events #1: The Bad Beginning"
[12:49:04 PM] ℹ️ Processing metadata citation 2 for "A Series of Unfortunate Events #1: The Bad Beginning"
[12:49:05 PM] ℹ️ Clicking to OPEN metadata citation 2
[12:49:07 PM] ℹ️ Extracted citation text (first 100 chars): Interest age, years: from 8 to 12...
[12:49:07 PM] ℹ️ Clicking to CLOSE metadata citation 2
[12:49:08 PM] ✅ ✅ metadata citation 2 closed successfully
[12:49:08 PM] ℹ️ ✅ Citation 2 extracted successfully (length: 33)
[12:49:08 PM] ℹ️ Extracting citation 3/3 for "A Series of Unfortunate Events #1: The Bad Beginning"
[12:49:08 PM] ℹ️ Processing metadata citation 3 for "A Series of Unfortunate Events #1: The Bad Beginning"
[12:49:09 PM] ℹ️ Clicking to OPEN metadata citation 3
[12:49:11 PM] ℹ️ Extracted citation text (first 100 chars): children...
[12:49:11 PM] ℹ️ Clicking to CLOSE metadata citation 3
[12:49:13 PM] ✅ ✅ metadata citation 3 closed successfully
[12:49:13 PM] ℹ️ ✅ Citation 3 extracted successfully (length: 8)
[12:49:13 PM] ℹ️ Collapsing book section: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:49:15 PM] ✅ Book section collapsed: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:49:15 PM] ✅ ✅ Completed processing "A Series of Unfortunate Events #1: The Bad Beginning" - 3 citations extracted
[12:49:15 PM] 📋 📖 PROCESSING BOOK 3: "Replay"
[12:49:15 PM] ℹ️ Expanding book section: "Replay"
[12:49:18 PM] ✅ Book section expanded: "Replay"
[12:49:18 PM] ℹ️ Expanding "Why this book is the match" section for: "Replay"
[12:49:21 PM] ✅ "Why this book is the match" section expanded for: "Replay"
[12:49:21 PM] ℹ️ Found 3 citation buttons for "Replay"
[12:49:21 PM] ℹ️ Extracting citation 1/3 for "Replay"
[12:49:21 PM] ℹ️ Processing metadata citation 1 for "Replay"
[12:49:22 PM] ℹ️ Clicking to OPEN metadata citation 1
[12:49:24 PM] ℹ️ Extracted citation text (first 100 chars): this is a story about twelve-year-old Leo, who has a talent for transforming the ordinary into the e...
[12:49:24 PM] ℹ️ Clicking to CLOSE metadata citation 1
[12:49:25 PM] ✅ ✅ metadata citation 1 closed successfully
[12:49:25 PM] ℹ️ ✅ Citation 1 extracted successfully (length: 112)
[12:49:25 PM] ℹ️ Extracting citation 2/3 for "Replay"
[12:49:25 PM] ℹ️ Processing metadata citation 2 for "Replay"
[12:49:26 PM] ℹ️ Clicking to OPEN metadata citation 2
[12:49:28 PM] ℹ️ Extracted citation text (first 100 chars): Interest age, years: from 8 to 12...
[12:49:28 PM] ℹ️ Clicking to CLOSE metadata citation 2
[12:49:30 PM] ✅ ✅ metadata citation 2 closed successfully
[12:49:30 PM] ℹ️ ✅ Citation 2 extracted successfully (length: 33)
[12:49:30 PM] ℹ️ Extracting citation 3/3 for "Replay"
[12:49:30 PM] ℹ️ Processing metadata citation 3 for "Replay"
[12:49:31 PM] ℹ️ Clicking to OPEN metadata citation 3
[12:49:33 PM] ℹ️ Extracted citation text (first 100 chars): With the universal theme of finding one's true identity, and set amid a loud, noisy, memorable famil...
[12:49:33 PM] ℹ️ Clicking to CLOSE metadata citation 3
[12:49:34 PM] ✅ ✅ metadata citation 3 closed successfully
[12:49:34 PM] ℹ️ ✅ Citation 3 extracted successfully (length: 101)
[12:49:34 PM] ℹ️ Collapsing book section: "Replay"
[12:49:36 PM] ✅ Book section collapsed: "Replay"
[12:49:36 PM] ✅ ✅ Completed processing "Replay" - 3 citations extracted
[12:49:36 PM] 📋 📖 PROCESSING BOOK 4: "A Christmas Carol"
[12:49:36 PM] ℹ️ Expanding book section: "A Christmas Carol"
[12:49:39 PM] ✅ Book section expanded: "A Christmas Carol"
[12:49:39 PM] ℹ️ "Why this book is the match" section already expanded for: "A Christmas Carol"
[12:49:39 PM] ℹ️ Found 3 citation buttons for "A Christmas Carol"
[12:49:39 PM] ℹ️ Extracting citation 1/3 for "A Christmas Carol"
[12:49:39 PM] ℹ️ Processing metadata citation 1 for "A Christmas Carol"
[12:49:40 PM] ℹ️ Clicking to OPEN metadata citation 1
[12:49:42 PM] ℹ️ Extracted citation text (first 100 chars): christmas books...
[12:49:42 PM] ℹ️ Clicking to CLOSE metadata citation 1
[12:49:44 PM] ✅ ✅ metadata citation 1 closed successfully
[12:49:44 PM] ℹ️ ✅ Citation 1 extracted successfully (length: 15)
[12:49:44 PM] ℹ️ Extracting citation 2/3 for "A Christmas Carol"
[12:49:44 PM] ℹ️ Processing metadata citation 2 for "A Christmas Carol"
[12:49:45 PM] ℹ️ Clicking to OPEN metadata citation 2
[12:49:47 PM] ℹ️ Extracted citation text (first 100 chars): But four ghostly visitors come to show him a different way, opening Scrooge’s heart to kindness and ...
[12:49:47 PM] ℹ️ Clicking to CLOSE metadata citation 2
[12:49:49 PM] ✅ ✅ metadata citation 2 closed successfully
[12:49:49 PM] ℹ️ ✅ Citation 2 extracted successfully (length: 175)
[12:49:49 PM] ℹ️ Extracting citation 3/3 for "A Christmas Carol"
[12:49:49 PM] ℹ️ Processing metadata citation 3 for "A Christmas Carol"
[12:49:50 PM] ℹ️ Clicking to OPEN metadata citation 3
[12:49:52 PM] ℹ️ Extracted citation text (first 100 chars): Interest age, years: from 6 to 10...
[12:49:52 PM] ℹ️ Clicking to CLOSE metadata citation 3
[12:49:53 PM] ✅ ✅ metadata citation 3 closed successfully
[12:49:53 PM] ℹ️ ✅ Citation 3 extracted successfully (length: 33)
[12:49:53 PM] ℹ️ Collapsing book section: "A Christmas Carol"
[12:49:55 PM] ✅ Book section collapsed: "A Christmas Carol"
[12:49:55 PM] ✅ ✅ Completed processing "A Christmas Carol" - 3 citations extracted
[12:49:55 PM] 📋 📖 PROCESSING BOOK 5: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:49:55 PM] ℹ️ Expanding book section: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:49:58 PM] ✅ Book section expanded: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:49:58 PM] ℹ️ "Why this book is the match" section already expanded for: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:49:58 PM] ℹ️ Found 3 citation buttons for "A Series of Unfortunate Events #1: The Bad Beginning"
[12:49:58 PM] ℹ️ Extracting citation 1/3 for "A Series of Unfortunate Events #1: The Bad Beginning"
[12:49:58 PM] ℹ️ Processing metadata citation 1 for "A Series of Unfortunate Events #1: The Bad Beginning"
[12:49:59 PM] ℹ️ Clicking to OPEN metadata citation 1
[12:50:01 PM] ℹ️ Extracted citation text (first 100 chars): a tale of three likeable and unfortunate children...
[12:50:01 PM] ℹ️ Clicking to CLOSE metadata citation 1
[12:50:03 PM] ✅ ✅ metadata citation 1 closed successfully
[12:50:03 PM] ℹ️ ✅ Citation 1 extracted successfully (length: 49)
[12:50:03 PM] ℹ️ Extracting citation 2/3 for "A Series of Unfortunate Events #1: The Bad Beginning"
[12:50:03 PM] ℹ️ Processing metadata citation 2 for "A Series of Unfortunate Events #1: The Bad Beginning"
[12:50:04 PM] ℹ️ Clicking to OPEN metadata citation 2
[12:50:06 PM] ℹ️ Extracted citation text (first 100 chars): Interest age, years: from 8 to 12...
[12:50:06 PM] ℹ️ Clicking to CLOSE metadata citation 2
[12:50:07 PM] ✅ ✅ metadata citation 2 closed successfully
[12:50:07 PM] ℹ️ ✅ Citation 2 extracted successfully (length: 33)
[12:50:07 PM] ℹ️ Extracting citation 3/3 for "A Series of Unfortunate Events #1: The Bad Beginning"
[12:50:07 PM] ℹ️ Processing metadata citation 3 for "A Series of Unfortunate Events #1: The Bad Beginning"
[12:50:08 PM] ℹ️ Clicking to OPEN metadata citation 3
[12:50:10 PM] ℹ️ Extracted citation text (first 100 chars): children...
[12:50:10 PM] ℹ️ Clicking to CLOSE metadata citation 3
[12:50:12 PM] ✅ ✅ metadata citation 3 closed successfully
[12:50:12 PM] ℹ️ ✅ Citation 3 extracted successfully (length: 8)
[12:50:12 PM] ℹ️ Collapsing book section: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:50:13 PM] ✅ Book section collapsed: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:50:13 PM] ✅ ✅ Completed processing "A Series of Unfortunate Events #1: The Bad Beginning" - 3 citations extracted
[12:50:13 PM] 📋 📖 PROCESSING BOOK 6: "Replay"
[12:50:13 PM] ℹ️ Expanding book section: "Replay"
[12:50:17 PM] ✅ Book section expanded: "Replay"
[12:50:17 PM] ℹ️ "Why this book is the match" section already expanded for: "Replay"
[12:50:17 PM] ℹ️ Found 3 citation buttons for "Replay"
[12:50:17 PM] ℹ️ Extracting citation 1/3 for "Replay"
[12:50:17 PM] ℹ️ Processing metadata citation 1 for "Replay"
[12:50:18 PM] ℹ️ Clicking to OPEN metadata citation 1
[12:50:20 PM] ℹ️ Extracted citation text (first 100 chars): a story about twelve-year-old Leo, who has a talent for transforming the ordinary into the extraordi...
[12:50:20 PM] ℹ️ Clicking to CLOSE metadata citation 1
[12:50:21 PM] ✅ ✅ metadata citation 1 closed successfully
[12:50:21 PM] ℹ️ ✅ Citation 1 extracted successfully (length: 104)
[12:50:21 PM] ℹ️ Extracting citation 2/3 for "Replay"
[12:50:21 PM] ℹ️ Processing metadata citation 2 for "Replay"
[12:50:22 PM] ℹ️ Clicking to OPEN metadata citation 2
[12:50:24 PM] ℹ️ Extracted citation text (first 100 chars): Interest age, years: from 8 to 12...
[12:50:24 PM] ℹ️ Clicking to CLOSE metadata citation 2
[12:50:26 PM] ✅ ✅ metadata citation 2 closed successfully
[12:50:26 PM] ℹ️ ✅ Citation 2 extracted successfully (length: 33)
[12:50:26 PM] ℹ️ Extracting citation 3/3 for "Replay"
[12:50:26 PM] ℹ️ Processing metadata citation 3 for "Replay"
[12:50:27 PM] ℹ️ Clicking to OPEN metadata citation 3
[12:50:29 PM] ℹ️ Extracted citation text (first 100 chars): With the universal theme of finding one's true identity, and set amid a loud, noisy, memorable famil...
[12:50:29 PM] ℹ️ Clicking to CLOSE metadata citation 3
[12:50:30 PM] ✅ ✅ metadata citation 3 closed successfully
[12:50:30 PM] ℹ️ ✅ Citation 3 extracted successfully (length: 101)
[12:50:30 PM] ℹ️ Collapsing book section: "Replay"
[12:50:32 PM] ✅ Book section collapsed: "Replay"
[12:50:32 PM] ✅ ✅ Completed processing "Replay" - 3 citations extracted
[12:50:32 PM] ✅ 🎉 Successfully extracted citations from 3 books
[12:50:32 PM] ℹ️ Step 3: Validating reason-citation matches
[12:50:32 PM] 📋 🔍 VALIDATING REASON-CITATION MATCHES (80% THRESHOLD)
[12:50:32 PM] ℹ️ 📖 Validating 3 reasons for: "A Christmas Carol"
[12:50:32 PM] ℹ️ Reason 1: Checking match...
[12:50:40 PM] ✅ ✅ "A Christmas Carol" - Reason 1: 85% match - PASS
[12:50:40 PM] ℹ️ Reason 2: Checking match...
[12:50:50 PM] ✅ ✅ "A Christmas Carol" - Reason 2: 98% match - PASS
[12:50:50 PM] ℹ️ Reason 3: Checking match...
[12:51:01 PM] ✅ ✅ "A Christmas Carol" - Reason 3: 95% match - PASS
[12:51:01 PM] ℹ️ 📖 Validating 3 reasons for: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:51:01 PM] ℹ️ Reason 1: Checking match...
[12:51:01 PM] ✅ ✅ "A Series of Unfortunate Events #1: The Bad Beginning" - Reason 1: 100% match - PASS
[12:51:01 PM] ℹ️ Reason 2: Checking match...
[12:51:01 PM] ❌ ❌ "A Series of Unfortunate Events #1: The Bad Beginning" - Reason 2: 0% match - FAIL
[12:51:01 PM] ❌ ⚠️ Citation text not found in reason: "Interest age, years: from 8 to 12"
[12:51:01 PM] ℹ️ Reason 3: Checking match...
[12:51:01 PM] ❌ ❌ "A Series of Unfortunate Events #1: The Bad Beginning" - Reason 3: 0% match - FAIL
[12:51:01 PM] ❌ ⚠️ Citation text not found in reason: "children"
[12:51:01 PM] ℹ️ 📖 Validating 3 reasons for: "Replay"
[12:51:01 PM] ℹ️ Reason 1: Checking match...
[12:51:01 PM] ❌ ❌ "Replay" - Reason 1: 0% match - FAIL
[12:51:01 PM] ❌ ⚠️ Citation text not found in reason: "a story about twelve-year-old Leo, who has a talent for transforming the ordinary into the extraordinary"
[12:51:01 PM] ℹ️ Reason 2: Checking match...
[12:51:01 PM] ❌ ❌ "Replay" - Reason 2: 0% match - FAIL
[12:51:01 PM] ❌ ⚠️ Citation text not found in reason: "Interest age, years: from 8 to 12"
[12:51:01 PM] ℹ️ Reason 3: Checking match...
[12:51:01 PM] ❌ ❌ "Replay" - Reason 3: 0% match - FAIL
[12:51:01 PM] ❌ ⚠️ Citation text not found in reason: "With the universal theme of finding one's true identity, and set amid a loud, noisy, memorable family"
[12:51:01 PM] 📊 📊 CITATION VALIDATION SUMMARY: 4/9 passed (44.4%)
[12:51:01 PM] ❌ 💥 REASON-CITATION VALIDATION: OVERALL FAILED
[12:51:01 PM] ℹ️ Step 4: Generating detailed report
[12:51:01 PM] 📋 📋 GENERATING CITATION VALIDATION REPORT
[12:51:01 PM] ℹ️ Report generated: 4/9 reasons passed
[12:51:01 PM] ✅ ✅ Citation validation workflow completed successfully
[12:51:01 PM] 📊 📈 OVERALL CITATION VALIDATION: 4/9 (44.4%)
[12:51:01 PM] ❌ ❌ REASON-CITATION VALIDATION: OVERALL FAILED - Only 44.4% of reasons match citations (required: 80%)
[12:51:01 PM] 📋 📋 GENERATING DETAILED CITATION VALIDATION REPORT
[12:51:01 PM] ℹ️ Generating HTML citation validation report
[12:51:01 PM] ✅ Generated HTML report for 9 reasons
[12:51:01 PM] ✅ ✅ Citation validation HTML report generated and attached
[12:51:01 PM] ✅ ✅ Citation validation plain text report attached
[12:51:01 PM] ℹ️ Checking database connectivity...
[12:51:01 PM] ✅ ✅ Database connected successfully
[12:51:01 PM] ℹ️ Database path: /home/runner/work/HC_Epub_Automation/HC_Epub_Automation/tests/test_data/database.xlsx
[12:51:01 PM] ℹ️ Total books: 10032
[12:51:02 PM] 📊 Database contains 10032 books
[12:51:02 PM] ✅ ✅ Database book count requirement met: 10032 >= 10032
[12:51:02 PM] 📋 🔍 VALIDATING EXTRACTED BOOKS INDIVIDUALLY AGAINST DATABASE
[12:51:02 PM] ℹ️ Checking book 1/3: "A Christmas Carol"
[12:51:02 PM] ℹ️ Checking if book exists in database: "A Christmas Carol"
[12:51:02 PM] ✅ ✅ Database match: "A Christmas Carol" → "A Christmas Carol"
[12:51:02 PM] ℹ️ Checking book 2/3: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:51:02 PM] ℹ️ Checking if book exists in database: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:51:02 PM] ✅ ✅ Database match: "A Series of Unfortunate Events #1: The Bad Beginning" → "Eve"
[12:51:02 PM] ℹ️ Checking book 3/3: "Replay"
[12:51:02 PM] ℹ️ Checking if book exists in database: "Replay"
[12:51:02 PM] ✅ ✅ Database match: "Replay" → "Foreplay"
[12:51:02 PM] 📊 INDIVIDUAL DATABASE VALIDATION SUMMARY: 3/3 books found in database
[12:51:02 PM] ✅ 🎉 ALL BOOKS VALIDATED SUCCESSFULLY AGAINST DATABASE!
[12:51:02 PM] 📋 🤖 VALIDATING RESPONSE RELEVANCE WITH GEMINI AI - PER BOOK ANALYSIS
[12:51:02 PM] ℹ️ Starting Gemini AI per-book relevance validation...
[12:51:02 PM] 📋 🤖 ANALYZING RESPONSE RELEVANCE WITH GEMINI AI - PER BOOK ANALYSIS
[12:51:02 PM] ℹ️ Starting Gemini AI analysis with retry logic...
[12:51:08 PM] ⚠ ⚠️ GEMINI VALIDATION: Using fallback analysis due to API issues
[12:51:08 PM] ℹ️ Overall Score: 70% (Fallback)
[12:51:08 PM] 📋 📖 ANALYSIS FOR: "A Christmas Carol" - Score: 70%
[12:51:08 PM] ℹ️ ❌ Author Information: 70% - Analysis unavailable - API error
[12:51:08 PM] ℹ️ ❌ Publishing Date: 70% - Analysis unavailable - API error
[12:51:08 PM] ℹ️ ❌ Why Match Explanations: 70% - Analysis unavailable - API error
[12:51:08 PM] ℹ️ ❌ Relevance Scores: 70% - Analysis unavailable - API error
[12:51:08 PM] ℹ️ 💡 Book-specific improvements:
[12:51:08 PM] ℹ️ - Retry analysis when service is available
[12:51:08 PM] 📋 📖 ANALYSIS FOR: "A Series of Unfortunate Events #1: The Bad Beginning" - Score: 70%
[12:51:08 PM] ℹ️ ❌ Author Information: 70% - Analysis unavailable - API error
[12:51:08 PM] ℹ️ ❌ Publishing Date: 70% - Analysis unavailable - API error
[12:51:08 PM] ℹ️ ❌ Why Match Explanations: 70% - Analysis unavailable - API error
[12:51:08 PM] ℹ️ ❌ Relevance Scores: 70% - Analysis unavailable - API error
[12:51:08 PM] ℹ️ 💡 Book-specific improvements:
[12:51:08 PM] ℹ️ - Retry analysis when service is available
[12:51:08 PM] 📋 📖 ANALYSIS FOR: "Replay" - Score: 70%
[12:51:08 PM] ℹ️ ❌ Author Information: 70% - Analysis unavailable - API error
[12:51:08 PM] ℹ️ ❌ Publishing Date: 70% - Analysis unavailable - API error
[12:51:08 PM] ℹ️ ❌ Why Match Explanations: 70% - Analysis unavailable - API error
[12:51:08 PM] ℹ️ ❌ Relevance Scores: 70% - Analysis unavailable - API error
[12:51:08 PM] ℹ️ 💡 Book-specific improvements:
[12:51:08 PM] ℹ️ - Retry analysis when service is available
[12:51:08 PM] ℹ️ 📊 SUMMARY FEEDBACK:
[12:51:08 PM] ℹ️ • AI analysis service is temporarily overloaded
[12:51:08 PM] ℹ️ • Using fallback analysis with neutral scores
[12:51:08 PM] ℹ️ • Error: [GoogleGenerativeAI Error]: Error fetching from https://generativelanguage.googleapis.com/v1beta/models/gemini-2.5-pro:generateContent: [429 Too Many Requests] You exceeded your current quota, please check your plan and billing details. For more information on this error, head to: https://ai.google.dev/gemini-api/docs/rate-limits.
* Quota exceeded for metric: generativelanguage.googleapis.com/generate_content_free_tier_requests, limit: 2
Please retry in 51.171089048s. [{"@type":"type.googleapis.com/google.rpc.QuotaFailure","violations":[{"quotaMetric":"generativelanguage.googleapis.com/generate_content_free_tier_requests","quotaId":"GenerateRequestsPerMinutePerProjectPerModel-FreeTier","quotaDimensions":{"model":"gemini-2.5-pro","location":"global"},"quotaValue":"2"}]},{"@type":"type.googleapis.com/google.rpc.Help","links":[{"description":"Learn more about Gemini API quotas","url":"https://ai.google.dev/gemini-api/docs/rate-limits"}]},{"@type":"type.googleapis.com/google.rpc.RetryInfo","retryDelay":"51s"}]
[12:51:08 PM] ❌ ❌ GEMINI AI VALIDATION FAILED: 70% relevance score (required: 80%)
[12:51:08 PM] ℹ️ Continuing execution due to soft assertion...
=== END LOGS ===
Scenario Logs: Book Genie Response Validation with Citation Verification
[12:47:32 PM] Navigating to Creative Workspace login page
[12:47:36 PM] Homepage visibility: false
[12:47:36 PM] ℹ️ Clicking on mode selection dropdown
[12:47:39 PM] ✅ Mode selection dropdown clicked successfully
[12:47:39 PM] ℹ️ Checking visibility of mode: BookGenieQA
[12:47:39 PM] ✅ Mode "BookGenieQA" is visible
[12:47:39 PM] ℹ️ Selecting mode: BookGenieQA
[12:47:40 PM] ✅ Mode "BookGenieQA" selected successfully
[12:47:42 PM] ℹ️ Waiting for mode transition to complete
[12:47:42 PM] ℹ️ Typing query: "Suggest 3 books for childrens"
[12:48:12 PM] ⚠ ⚠Book Genie mode did not loaded
[12:48:12 PM] ℹ️ Preparing to type query: "Suggest 3 books for childrens"
[12:48:12 PM] ℹ️ Waiting for chat input to be visible
[12:48:12 PM] ℹ️ Clearing chat input
[12:48:12 PM] ℹ️ Typing query: "Suggest 3 books for childrens"
[12:48:13 PM] ℹ️ Pressing Enter to submit query
[12:48:13 PM] ✅ Query submitted successfully: "Suggest 3 books for childrens"
[12:48:13 PM] ℹ️ Waiting for AI to complete thinking...
[12:48:13 PM] 📋 WAITING FOR AI RESPONSE
[12:48:13 PM] ℹ️ Looking for AI thinking indicator...
[12:48:13 PM] ℹ️ Waiting for thinking indicator to appear (max 2 minutes)
[12:48:15 PM] ✅ ✓ AI thinking indicator appeared - AI is processing the request
[12:48:15 PM] ℹ️ Waiting for thinking indicator to disappear (max 5 minutes)
[12:48:27 PM] ✅ ✓ AI thinking completed - Response should be ready
[12:48:27 PM] ℹ️ 🔍 Checking for "None of the above, just" option...
[12:48:28 PM] ℹ️ ✓ "None of the above, just" option not present - proceeding normally
[12:48:28 PM] ℹ️ Allowing additional time for response rendering
[12:48:31 PM] ✅ AI response wait process completed
[12:48:31 PM] ℹ️ Validating response for query: "Suggest 3 books for childrens"
[12:48:31 PM] 📋 VALIDATING RESPONSE FOR QUERY: "Suggest 3 books for childrens"
[12:48:31 PM] ℹ️ Locating the latest response in chat
[12:48:31 PM] ℹ️ Waiting for response to be visible (max 30 seconds)
[12:48:31 PM] ✅ Response received - Length: 2233 characters
[12:48:31 PM] ℹ️ Response preview: Watch Me WorkRetrieving Relevant Books for 'books for children' in Book Summary ...Retrieving Releva...
[12:48:31 PM] ℹ️ Extracting book data from response...
[12:48:31 PM] 📋 EXTRACTING BOOK DATA FROM RESPONSE
[12:48:31 PM] ℹ️ Locating BookGenie response container
[12:48:31 PM] ℹ️ Extracting HTML content from response
[12:48:31 PM] ℹ️ HTML content extracted - Length: 22514 characters
[12:48:31 PM] ℹ️ Parsing HTML to extract book data
[12:48:31 PM] ✅ SUCCESSFULLY EXTRACTED 3 BOOKS
[12:48:31 PM] 📋 EXTRACTED BOOKS SUMMARY
[12:48:31 PM] 📚 1. "A Christmas Carol" - Score: 100%%
[12:48:31 PM] 📚 2. "A Series of Unfortunate Events #1: The Bad Beginning" - Score: 100%%
[12:48:31 PM] 📚 3. "Replay" - Score: 100%%
[12:48:31 PM] 📊 Total books extracted: 3
[12:48:31 PM] 📊 Average relevance score: 100%
[12:48:31 PM] ✅ Extracted 3 books from response
[12:48:31 PM] ℹ️ Saving 3 books to Excel file: test_results/suggest_3_books_for_childrens.xlsx
[12:48:31 PM] 📋 SAVING BOOKS TO EXCEL FILE
[12:48:31 PM] ℹ️ Target file path: test_results/suggest_3_books_for_childrens.xlsx
[12:48:31 PM] ℹ️ Exporting 3 books to Excel format
[12:48:31 PM] ✅ Book data saved to: test_results/suggest_3_books_for_childrens.xlsx
[12:48:31 PM] 📊 File verified - Size: 17.07 KB
[12:48:31 PM] ✅ ✓ Excel file creation verified successfully
[12:48:31 PM] 📋 STARTING INDIVIDUAL BOOK VALIDATION (3 books) for query: "Suggest 3 books for childrens"
[12:48:31 PM] 📋 📚 VALIDATING BOOK 1: "A Christmas Carol"
[12:48:31 PM] ℹ️ Starting comprehensive book validation
[12:48:31 PM] ℹ️ Validating title: "A Christmas Carol" vs expected: "A Christmas Carol"
[12:48:31 PM] ✅ ✅ TITLE VALIDATION PASSED: "A Christmas Carol"
[12:48:31 PM] ℹ️ Validating score: 100% vs expected: 100%
[12:48:31 PM] ✅ ✅ SCORE VALIDATION PASSED: 100% (expected 100%)
[12:48:31 PM] ✅ ✅ PERFECT SCORE GAP VALIDATION: Book has 100% score and no gap mentioned
[12:48:31 PM] ℹ️ Why-match points: extracted 3 vs expected 3
[12:48:31 PM] ✅ ✅ WHY-MATCH VALIDATION PASSED: 3 points (expected 3)
[12:48:31 PM] ✅ 🎉 BOOK 1 VALIDATION: ALL CHECKS PASSED!
[12:48:31 PM] ℹ️ Book 1 validation completed
[12:48:31 PM] 📋 📚 VALIDATING BOOK 2: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:48:31 PM] ℹ️ Starting comprehensive book validation
[12:48:31 PM] ℹ️ Validating title: "A Series of Unfortunate Events #1: The Bad Beginning" vs expected: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:48:31 PM] ✅ ✅ TITLE VALIDATION PASSED: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:48:31 PM] ℹ️ Validating score: 100% vs expected: 100%
[12:48:31 PM] ✅ ✅ SCORE VALIDATION PASSED: 100% (expected 100%)
[12:48:31 PM] ✅ ✅ PERFECT SCORE GAP VALIDATION: Book has 100% score and no gap mentioned
[12:48:31 PM] ℹ️ Why-match points: extracted 3 vs expected 3
[12:48:31 PM] ✅ ✅ WHY-MATCH VALIDATION PASSED: 3 points (expected 3)
[12:48:31 PM] ✅ 🎉 BOOK 2 VALIDATION: ALL CHECKS PASSED!
[12:48:31 PM] ℹ️ Book 2 validation completed
[12:48:31 PM] 📋 📚 VALIDATING BOOK 3: "Replay"
[12:48:31 PM] ℹ️ Starting comprehensive book validation
[12:48:31 PM] ℹ️ Validating title: "Replay" vs expected: "Replay"
[12:48:31 PM] ✅ ✅ TITLE VALIDATION PASSED: "Replay"
[12:48:31 PM] ℹ️ Validating score: 100% vs expected: 100%
[12:48:31 PM] ✅ ✅ SCORE VALIDATION PASSED: 100% (expected 100%)
[12:48:31 PM] ✅ ✅ PERFECT SCORE GAP VALIDATION: Book has 100% score and no gap mentioned
[12:48:31 PM] ℹ️ Why-match points: extracted 3 vs expected 3
[12:48:31 PM] ✅ ✅ WHY-MATCH VALIDATION PASSED: 3 points (expected 3)
[12:48:31 PM] ✅ 🎉 BOOK 3 VALIDATION: ALL CHECKS PASSED!
[12:48:31 PM] ℹ️ Book 3 validation completed
[12:48:31 PM] 📊 VALIDATION SUMMARY: 3/3 books passed
[12:48:31 PM] ✅ ALL BOOKS VALIDATED SUCCESSFULLY!
[12:48:31 PM] 📋 🔍 VALIDATING REASON-CITATION MATCHES
[12:48:31 PM] ℹ️ Starting citation validation process...
[12:48:31 PM] 📋 🚀 STARTING COMPLETE CITATION VALIDATION WORKFLOW
[12:48:31 PM] ℹ️ Step 1: Extracting books from AI response
[12:48:31 PM] 📋 EXTRACTING BOOK DATA FROM RESPONSE
[12:48:31 PM] ℹ️ Locating BookGenie response container
[12:48:31 PM] ℹ️ Extracting HTML content from response
[12:48:31 PM] ℹ️ HTML content extracted - Length: 22514 characters
[12:48:31 PM] ℹ️ Parsing HTML to extract book data
[12:48:31 PM] ✅ SUCCESSFULLY EXTRACTED 3 BOOKS
[12:48:31 PM] 📋 EXTRACTED BOOKS SUMMARY
[12:48:31 PM] 📚 1. "A Christmas Carol" - Score: 100%%
[12:48:31 PM] 📚 2. "A Series of Unfortunate Events #1: The Bad Beginning" - Score: 100%%
[12:48:31 PM] 📚 3. "Replay" - Score: 100%%
[12:48:31 PM] 📊 Total books extracted: 3
[12:48:31 PM] 📊 Average relevance score: 100%
[12:48:31 PM] ℹ️ Step 2: Extracting citation texts from book elements
[12:48:31 PM] 📋 📚 EXTRACTING CITATION TEXTS FROM BOOK ELEMENTS
[12:48:31 PM] ℹ️ Step 1: Waiting for main book section
[12:48:31 PM] ℹ️ Found 14 total accordion sections
[12:48:31 PM] ℹ️ Found individual book: "1. A Christmas Carol"
[12:48:31 PM] ℹ️ Found individual book: "2. A Series of Unfortunate Events #1: The Bad Beginning"
[12:48:31 PM] ℹ️ Found individual book: "3. Replay"
[12:48:31 PM] ℹ️ Found individual book: "1. A Christmas Carol"
[12:48:31 PM] ℹ️ Found individual book: "2. A Series of Unfortunate Events #1: The Bad Beginning"
[12:48:31 PM] ℹ️ Found individual book: "3. Replay"
[12:48:31 PM] ℹ️ Processing 6 individual book sections
[12:48:31 PM] 📋 📖 PROCESSING BOOK 1: "A Christmas Carol"
[12:48:31 PM] ℹ️ Expanding book section: "A Christmas Carol"
[12:48:34 PM] ✅ Book section expanded: "A Christmas Carol"
[12:48:34 PM] ℹ️ Expanding "Why this book is the match" section for: "A Christmas Carol"
[12:48:38 PM] ✅ "Why this book is the match" section expanded for: "A Christmas Carol"
[12:48:38 PM] ℹ️ Found 3 citation buttons for "A Christmas Carol"
[12:48:38 PM] ℹ️ Extracting citation 1/3 for "A Christmas Carol"
[12:48:38 PM] ℹ️ Processing metadata citation 1 for "A Christmas Carol"
[12:48:39 PM] ℹ️ Clicking to OPEN metadata citation 1
[12:48:41 PM] ℹ️ Extracted citation text (first 100 chars): christmas books...
[12:48:41 PM] ℹ️ Clicking to CLOSE metadata citation 1
[12:48:42 PM] ✅ ✅ metadata citation 1 closed successfully
[12:48:42 PM] ℹ️ ✅ Citation 1 extracted successfully (length: 15)
[12:48:42 PM] ℹ️ Extracting citation 2/3 for "A Christmas Carol"
[12:48:42 PM] ℹ️ Processing metadata citation 2 for "A Christmas Carol"
[12:48:43 PM] ℹ️ Clicking to OPEN metadata citation 2
[12:48:45 PM] ℹ️ Extracted citation text (first 100 chars): But four ghostly visitors come to show him a different way, opening Scrooge’s heart to kindness and ...
[12:48:45 PM] ℹ️ Clicking to CLOSE metadata citation 2
[12:48:47 PM] ✅ ✅ metadata citation 2 closed successfully
[12:48:47 PM] ℹ️ ✅ Citation 2 extracted successfully (length: 175)
[12:48:47 PM] ℹ️ Extracting citation 3/3 for "A Christmas Carol"
[12:48:47 PM] ℹ️ Processing metadata citation 3 for "A Christmas Carol"
[12:48:48 PM] ℹ️ Clicking to OPEN metadata citation 3
[12:48:50 PM] ℹ️ Extracted citation text (first 100 chars): Interest age, years: from 6 to 10...
[12:48:50 PM] ℹ️ Clicking to CLOSE metadata citation 3
[12:48:51 PM] ✅ ✅ metadata citation 3 closed successfully
[12:48:51 PM] ℹ️ ✅ Citation 3 extracted successfully (length: 33)
[12:48:51 PM] ℹ️ Collapsing book section: "A Christmas Carol"
[12:48:53 PM] ✅ Book section collapsed: "A Christmas Carol"
[12:48:53 PM] ✅ ✅ Completed processing "A Christmas Carol" - 3 citations extracted
[12:48:53 PM] 📋 📖 PROCESSING BOOK 2: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:48:53 PM] ℹ️ Expanding book section: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:48:56 PM] ✅ Book section expanded: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:48:56 PM] ℹ️ Expanding "Why this book is the match" section for: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:48:59 PM] ✅ "Why this book is the match" section expanded for: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:48:59 PM] ℹ️ Found 3 citation buttons for "A Series of Unfortunate Events #1: The Bad Beginning"
[12:48:59 PM] ℹ️ Extracting citation 1/3 for "A Series of Unfortunate Events #1: The Bad Beginning"
[12:48:59 PM] ℹ️ Processing metadata citation 1 for "A Series of Unfortunate Events #1: The Bad Beginning"
[12:49:00 PM] ℹ️ Clicking to OPEN metadata citation 1
[12:49:02 PM] ℹ️ Extracted citation text (first 100 chars): a tale of three likeable and unfortunate children...
[12:49:02 PM] ℹ️ Clicking to CLOSE metadata citation 1
[12:49:04 PM] ✅ ✅ metadata citation 1 closed successfully
[12:49:04 PM] ℹ️ ✅ Citation 1 extracted successfully (length: 49)
[12:49:04 PM] ℹ️ Extracting citation 2/3 for "A Series of Unfortunate Events #1: The Bad Beginning"
[12:49:04 PM] ℹ️ Processing metadata citation 2 for "A Series of Unfortunate Events #1: The Bad Beginning"
[12:49:05 PM] ℹ️ Clicking to OPEN metadata citation 2
[12:49:07 PM] ℹ️ Extracted citation text (first 100 chars): Interest age, years: from 8 to 12...
[12:49:07 PM] ℹ️ Clicking to CLOSE metadata citation 2
[12:49:08 PM] ✅ ✅ metadata citation 2 closed successfully
[12:49:08 PM] ℹ️ ✅ Citation 2 extracted successfully (length: 33)
[12:49:08 PM] ℹ️ Extracting citation 3/3 for "A Series of Unfortunate Events #1: The Bad Beginning"
[12:49:08 PM] ℹ️ Processing metadata citation 3 for "A Series of Unfortunate Events #1: The Bad Beginning"
[12:49:09 PM] ℹ️ Clicking to OPEN metadata citation 3
[12:49:11 PM] ℹ️ Extracted citation text (first 100 chars): children...
[12:49:11 PM] ℹ️ Clicking to CLOSE metadata citation 3
[12:49:13 PM] ✅ ✅ metadata citation 3 closed successfully
[12:49:13 PM] ℹ️ ✅ Citation 3 extracted successfully (length: 8)
[12:49:13 PM] ℹ️ Collapsing book section: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:49:15 PM] ✅ Book section collapsed: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:49:15 PM] ✅ ✅ Completed processing "A Series of Unfortunate Events #1: The Bad Beginning" - 3 citations extracted
[12:49:15 PM] 📋 📖 PROCESSING BOOK 3: "Replay"
[12:49:15 PM] ℹ️ Expanding book section: "Replay"
[12:49:18 PM] ✅ Book section expanded: "Replay"
[12:49:18 PM] ℹ️ Expanding "Why this book is the match" section for: "Replay"
[12:49:21 PM] ✅ "Why this book is the match" section expanded for: "Replay"
[12:49:21 PM] ℹ️ Found 3 citation buttons for "Replay"
[12:49:21 PM] ℹ️ Extracting citation 1/3 for "Replay"
[12:49:21 PM] ℹ️ Processing metadata citation 1 for "Replay"
[12:49:22 PM] ℹ️ Clicking to OPEN metadata citation 1
[12:49:24 PM] ℹ️ Extracted citation text (first 100 chars): this is a story about twelve-year-old Leo, who has a talent for transforming the ordinary into the e...
[12:49:24 PM] ℹ️ Clicking to CLOSE metadata citation 1
[12:49:25 PM] ✅ ✅ metadata citation 1 closed successfully
[12:49:25 PM] ℹ️ ✅ Citation 1 extracted successfully (length: 112)
[12:49:25 PM] ℹ️ Extracting citation 2/3 for "Replay"
[12:49:25 PM] ℹ️ Processing metadata citation 2 for "Replay"
[12:49:26 PM] ℹ️ Clicking to OPEN metadata citation 2
[12:49:28 PM] ℹ️ Extracted citation text (first 100 chars): Interest age, years: from 8 to 12...
[12:49:28 PM] ℹ️ Clicking to CLOSE metadata citation 2
[12:49:30 PM] ✅ ✅ metadata citation 2 closed successfully
[12:49:30 PM] ℹ️ ✅ Citation 2 extracted successfully (length: 33)
[12:49:30 PM] ℹ️ Extracting citation 3/3 for "Replay"
[12:49:30 PM] ℹ️ Processing metadata citation 3 for "Replay"
[12:49:31 PM] ℹ️ Clicking to OPEN metadata citation 3
[12:49:33 PM] ℹ️ Extracted citation text (first 100 chars): With the universal theme of finding one's true identity, and set amid a loud, noisy, memorable famil...
[12:49:33 PM] ℹ️ Clicking to CLOSE metadata citation 3
[12:49:34 PM] ✅ ✅ metadata citation 3 closed successfully
[12:49:34 PM] ℹ️ ✅ Citation 3 extracted successfully (length: 101)
[12:49:34 PM] ℹ️ Collapsing book section: "Replay"
[12:49:36 PM] ✅ Book section collapsed: "Replay"
[12:49:36 PM] ✅ ✅ Completed processing "Replay" - 3 citations extracted
[12:49:36 PM] 📋 📖 PROCESSING BOOK 4: "A Christmas Carol"
[12:49:36 PM] ℹ️ Expanding book section: "A Christmas Carol"
[12:49:39 PM] ✅ Book section expanded: "A Christmas Carol"
[12:49:39 PM] ℹ️ "Why this book is the match" section already expanded for: "A Christmas Carol"
[12:49:39 PM] ℹ️ Found 3 citation buttons for "A Christmas Carol"
[12:49:39 PM] ℹ️ Extracting citation 1/3 for "A Christmas Carol"
[12:49:39 PM] ℹ️ Processing metadata citation 1 for "A Christmas Carol"
[12:49:40 PM] ℹ️ Clicking to OPEN metadata citation 1
[12:49:42 PM] ℹ️ Extracted citation text (first 100 chars): christmas books...
[12:49:42 PM] ℹ️ Clicking to CLOSE metadata citation 1
[12:49:44 PM] ✅ ✅ metadata citation 1 closed successfully
[12:49:44 PM] ℹ️ ✅ Citation 1 extracted successfully (length: 15)
[12:49:44 PM] ℹ️ Extracting citation 2/3 for "A Christmas Carol"
[12:49:44 PM] ℹ️ Processing metadata citation 2 for "A Christmas Carol"
[12:49:45 PM] ℹ️ Clicking to OPEN metadata citation 2
[12:49:47 PM] ℹ️ Extracted citation text (first 100 chars): But four ghostly visitors come to show him a different way, opening Scrooge’s heart to kindness and ...
[12:49:47 PM] ℹ️ Clicking to CLOSE metadata citation 2
[12:49:49 PM] ✅ ✅ metadata citation 2 closed successfully
[12:49:49 PM] ℹ️ ✅ Citation 2 extracted successfully (length: 175)
[12:49:49 PM] ℹ️ Extracting citation 3/3 for "A Christmas Carol"
[12:49:49 PM] ℹ️ Processing metadata citation 3 for "A Christmas Carol"
[12:49:50 PM] ℹ️ Clicking to OPEN metadata citation 3
[12:49:52 PM] ℹ️ Extracted citation text (first 100 chars): Interest age, years: from 6 to 10...
[12:49:52 PM] ℹ️ Clicking to CLOSE metadata citation 3
[12:49:53 PM] ✅ ✅ metadata citation 3 closed successfully
[12:49:53 PM] ℹ️ ✅ Citation 3 extracted successfully (length: 33)
[12:49:53 PM] ℹ️ Collapsing book section: "A Christmas Carol"
[12:49:55 PM] ✅ Book section collapsed: "A Christmas Carol"
[12:49:55 PM] ✅ ✅ Completed processing "A Christmas Carol" - 3 citations extracted
[12:49:55 PM] 📋 📖 PROCESSING BOOK 5: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:49:55 PM] ℹ️ Expanding book section: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:49:58 PM] ✅ Book section expanded: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:49:58 PM] ℹ️ "Why this book is the match" section already expanded for: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:49:58 PM] ℹ️ Found 3 citation buttons for "A Series of Unfortunate Events #1: The Bad Beginning"
[12:49:58 PM] ℹ️ Extracting citation 1/3 for "A Series of Unfortunate Events #1: The Bad Beginning"
[12:49:58 PM] ℹ️ Processing metadata citation 1 for "A Series of Unfortunate Events #1: The Bad Beginning"
[12:49:59 PM] ℹ️ Clicking to OPEN metadata citation 1
[12:50:01 PM] ℹ️ Extracted citation text (first 100 chars): a tale of three likeable and unfortunate children...
[12:50:01 PM] ℹ️ Clicking to CLOSE metadata citation 1
[12:50:03 PM] ✅ ✅ metadata citation 1 closed successfully
[12:50:03 PM] ℹ️ ✅ Citation 1 extracted successfully (length: 49)
[12:50:03 PM] ℹ️ Extracting citation 2/3 for "A Series of Unfortunate Events #1: The Bad Beginning"
[12:50:03 PM] ℹ️ Processing metadata citation 2 for "A Series of Unfortunate Events #1: The Bad Beginning"
[12:50:04 PM] ℹ️ Clicking to OPEN metadata citation 2
[12:50:06 PM] ℹ️ Extracted citation text (first 100 chars): Interest age, years: from 8 to 12...
[12:50:06 PM] ℹ️ Clicking to CLOSE metadata citation 2
[12:50:07 PM] ✅ ✅ metadata citation 2 closed successfully
[12:50:07 PM] ℹ️ ✅ Citation 2 extracted successfully (length: 33)
[12:50:07 PM] ℹ️ Extracting citation 3/3 for "A Series of Unfortunate Events #1: The Bad Beginning"
[12:50:07 PM] ℹ️ Processing metadata citation 3 for "A Series of Unfortunate Events #1: The Bad Beginning"
[12:50:08 PM] ℹ️ Clicking to OPEN metadata citation 3
[12:50:10 PM] ℹ️ Extracted citation text (first 100 chars): children...
[12:50:10 PM] ℹ️ Clicking to CLOSE metadata citation 3
[12:50:12 PM] ✅ ✅ metadata citation 3 closed successfully
[12:50:12 PM] ℹ️ ✅ Citation 3 extracted successfully (length: 8)
[12:50:12 PM] ℹ️ Collapsing book section: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:50:13 PM] ✅ Book section collapsed: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:50:13 PM] ✅ ✅ Completed processing "A Series of Unfortunate Events #1: The Bad Beginning" - 3 citations extracted
[12:50:13 PM] 📋 📖 PROCESSING BOOK 6: "Replay"
[12:50:13 PM] ℹ️ Expanding book section: "Replay"
[12:50:17 PM] ✅ Book section expanded: "Replay"
[12:50:17 PM] ℹ️ "Why this book is the match" section already expanded for: "Replay"
[12:50:17 PM] ℹ️ Found 3 citation buttons for "Replay"
[12:50:17 PM] ℹ️ Extracting citation 1/3 for "Replay"
[12:50:17 PM] ℹ️ Processing metadata citation 1 for "Replay"
[12:50:18 PM] ℹ️ Clicking to OPEN metadata citation 1
[12:50:20 PM] ℹ️ Extracted citation text (first 100 chars): a story about twelve-year-old Leo, who has a talent for transforming the ordinary into the extraordi...
[12:50:20 PM] ℹ️ Clicking to CLOSE metadata citation 1
[12:50:21 PM] ✅ ✅ metadata citation 1 closed successfully
[12:50:21 PM] ℹ️ ✅ Citation 1 extracted successfully (length: 104)
[12:50:21 PM] ℹ️ Extracting citation 2/3 for "Replay"
[12:50:21 PM] ℹ️ Processing metadata citation 2 for "Replay"
[12:50:22 PM] ℹ️ Clicking to OPEN metadata citation 2
[12:50:24 PM] ℹ️ Extracted citation text (first 100 chars): Interest age, years: from 8 to 12...
[12:50:24 PM] ℹ️ Clicking to CLOSE metadata citation 2
[12:50:26 PM] ✅ ✅ metadata citation 2 closed successfully
[12:50:26 PM] ℹ️ ✅ Citation 2 extracted successfully (length: 33)
[12:50:26 PM] ℹ️ Extracting citation 3/3 for "Replay"
[12:50:26 PM] ℹ️ Processing metadata citation 3 for "Replay"
[12:50:27 PM] ℹ️ Clicking to OPEN metadata citation 3
[12:50:29 PM] ℹ️ Extracted citation text (first 100 chars): With the universal theme of finding one's true identity, and set amid a loud, noisy, memorable famil...
[12:50:29 PM] ℹ️ Clicking to CLOSE metadata citation 3
[12:50:30 PM] ✅ ✅ metadata citation 3 closed successfully
[12:50:30 PM] ℹ️ ✅ Citation 3 extracted successfully (length: 101)
[12:50:30 PM] ℹ️ Collapsing book section: "Replay"
[12:50:32 PM] ✅ Book section collapsed: "Replay"
[12:50:32 PM] ✅ ✅ Completed processing "Replay" - 3 citations extracted
[12:50:32 PM] ✅ 🎉 Successfully extracted citations from 3 books
[12:50:32 PM] ℹ️ Step 3: Validating reason-citation matches
[12:50:32 PM] 📋 🔍 VALIDATING REASON-CITATION MATCHES (80% THRESHOLD)
[12:50:32 PM] ℹ️ 📖 Validating 3 reasons for: "A Christmas Carol"
[12:50:32 PM] ℹ️ Reason 1: Checking match...
[12:50:40 PM] ✅ ✅ "A Christmas Carol" - Reason 1: 85% match - PASS
[12:50:40 PM] ℹ️ Reason 2: Checking match...
[12:50:50 PM] ✅ ✅ "A Christmas Carol" - Reason 2: 98% match - PASS
[12:50:50 PM] ℹ️ Reason 3: Checking match...
[12:51:01 PM] ✅ ✅ "A Christmas Carol" - Reason 3: 95% match - PASS
[12:51:01 PM] ℹ️ 📖 Validating 3 reasons for: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:51:01 PM] ℹ️ Reason 1: Checking match...
[12:51:01 PM] ✅ ✅ "A Series of Unfortunate Events #1: The Bad Beginning" - Reason 1: 100% match - PASS
[12:51:01 PM] ℹ️ Reason 2: Checking match...
[12:51:01 PM] ❌ ❌ "A Series of Unfortunate Events #1: The Bad Beginning" - Reason 2: 0% match - FAIL
[12:51:01 PM] ❌ ⚠️ Citation text not found in reason: "Interest age, years: from 8 to 12"
[12:51:01 PM] ℹ️ Reason 3: Checking match...
[12:51:01 PM] ❌ ❌ "A Series of Unfortunate Events #1: The Bad Beginning" - Reason 3: 0% match - FAIL
[12:51:01 PM] ❌ ⚠️ Citation text not found in reason: "children"
[12:51:01 PM] ℹ️ 📖 Validating 3 reasons for: "Replay"
[12:51:01 PM] ℹ️ Reason 1: Checking match...
[12:51:01 PM] ❌ ❌ "Replay" - Reason 1: 0% match - FAIL
[12:51:01 PM] ❌ ⚠️ Citation text not found in reason: "a story about twelve-year-old Leo, who has a talent for transforming the ordinary into the extraordinary"
[12:51:01 PM] ℹ️ Reason 2: Checking match...
[12:51:01 PM] ❌ ❌ "Replay" - Reason 2: 0% match - FAIL
[12:51:01 PM] ❌ ⚠️ Citation text not found in reason: "Interest age, years: from 8 to 12"
[12:51:01 PM] ℹ️ Reason 3: Checking match...
[12:51:01 PM] ❌ ❌ "Replay" - Reason 3: 0% match - FAIL
[12:51:01 PM] ❌ ⚠️ Citation text not found in reason: "With the universal theme of finding one's true identity, and set amid a loud, noisy, memorable family"
[12:51:01 PM] 📊 📊 CITATION VALIDATION SUMMARY: 4/9 passed (44.4%)
[12:51:01 PM] ❌ 💥 REASON-CITATION VALIDATION: OVERALL FAILED
[12:51:01 PM] ℹ️ Step 4: Generating detailed report
[12:51:01 PM] 📋 📋 GENERATING CITATION VALIDATION REPORT
[12:51:01 PM] ℹ️ Report generated: 4/9 reasons passed
[12:51:01 PM] ✅ ✅ Citation validation workflow completed successfully
[12:51:01 PM] 📊 📈 OVERALL CITATION VALIDATION: 4/9 (44.4%)
[12:51:01 PM] ❌ ❌ REASON-CITATION VALIDATION: OVERALL FAILED - Only 44.4% of reasons match citations (required: 80%)
[12:51:01 PM] 📋 📋 GENERATING DETAILED CITATION VALIDATION REPORT
[12:51:01 PM] ℹ️ Generating HTML citation validation report
[12:51:01 PM] ✅ Generated HTML report for 9 reasons
[12:51:01 PM] ✅ ✅ Citation validation HTML report generated and attached
[12:51:01 PM] ✅ ✅ Citation validation plain text report attached
[12:51:01 PM] ℹ️ Checking database connectivity...
[12:51:01 PM] ✅ ✅ Database connected successfully
[12:51:01 PM] ℹ️ Database path: /home/runner/work/HC_Epub_Automation/HC_Epub_Automation/tests/test_data/database.xlsx
[12:51:01 PM] ℹ️ Total books: 10032
[12:51:02 PM] 📊 Database contains 10032 books
[12:51:02 PM] ✅ ✅ Database book count requirement met: 10032 >= 10032
[12:51:02 PM] 📋 🔍 VALIDATING EXTRACTED BOOKS INDIVIDUALLY AGAINST DATABASE
[12:51:02 PM] ℹ️ Checking book 1/3: "A Christmas Carol"
[12:51:02 PM] ℹ️ Checking if book exists in database: "A Christmas Carol"
[12:51:02 PM] ✅ ✅ Database match: "A Christmas Carol" → "A Christmas Carol"
[12:51:02 PM] ℹ️ Checking book 2/3: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:51:02 PM] ℹ️ Checking if book exists in database: "A Series of Unfortunate Events #1: The Bad Beginning"
[12:51:02 PM] ✅ ✅ Database match: "A Series of Unfortunate Events #1: The Bad Beginning" → "Eve"
[12:51:02 PM] ℹ️ Checking book 3/3: "Replay"
[12:51:02 PM] ℹ️ Checking if book exists in database: "Replay"
[12:51:02 PM] ✅ ✅ Database match: "Replay" → "Foreplay"
[12:51:02 PM] 📊 INDIVIDUAL DATABASE VALIDATION SUMMARY: 3/3 books found in database
[12:51:02 PM] ✅ 🎉 ALL BOOKS VALIDATED SUCCESSFULLY AGAINST DATABASE!
[12:51:02 PM] 📋 🤖 VALIDATING RESPONSE RELEVANCE WITH GEMINI AI - PER BOOK ANALYSIS
[12:51:02 PM] ℹ️ Starting Gemini AI per-book relevance validation...
[12:51:02 PM] 📋 🤖 ANALYZING RESPONSE RELEVANCE WITH GEMINI AI - PER BOOK ANALYSIS
[12:51:02 PM] ℹ️ Starting Gemini AI analysis with retry logic...
[12:51:08 PM] ⚠ ⚠️ GEMINI VALIDATION: Using fallback analysis due to API issues
[12:51:08 PM] ℹ️ Overall Score: 70% (Fallback)
[12:51:08 PM] 📋 📖 ANALYSIS FOR: "A Christmas Carol" - Score: 70%
[12:51:08 PM] ℹ️ ❌ Author Information: 70% - Analysis unavailable - API error
[12:51:08 PM] ℹ️ ❌ Publishing Date: 70% - Analysis unavailable - API error
[12:51:08 PM] ℹ️ ❌ Why Match Explanations: 70% - Analysis unavailable - API error
[12:51:08 PM] ℹ️ ❌ Relevance Scores: 70% - Analysis unavailable - API error
[12:51:08 PM] ℹ️ 💡 Book-specific improvements:
[12:51:08 PM] ℹ️ - Retry analysis when service is available
[12:51:08 PM] 📋 📖 ANALYSIS FOR: "A Series of Unfortunate Events #1: The Bad Beginning" - Score: 70%
[12:51:08 PM] ℹ️ ❌ Author Information: 70% - Analysis unavailable - API error
[12:51:08 PM] ℹ️ ❌ Publishing Date: 70% - Analysis unavailable - API error
[12:51:08 PM] ℹ️ ❌ Why Match Explanations: 70% - Analysis unavailable - API error
[12:51:08 PM] ℹ️ ❌ Relevance Scores: 70% - Analysis unavailable - API error
[12:51:08 PM] ℹ️ 💡 Book-specific improvements:
[12:51:08 PM] ℹ️ - Retry analysis when service is available
[12:51:08 PM] 📋 📖 ANALYSIS FOR: "Replay" - Score: 70%
[12:51:08 PM] ℹ️ ❌ Author Information: 70% - Analysis unavailable - API error
[12:51:08 PM] ℹ️ ❌ Publishing Date: 70% - Analysis unavailable - API error
[12:51:08 PM] ℹ️ ❌ Why Match Explanations: 70% - Analysis unavailable - API error
[12:51:08 PM] ℹ️ ❌ Relevance Scores: 70% - Analysis unavailable - API error
[12:51:08 PM] ℹ️ 💡 Book-specific improvements:
[12:51:08 PM] ℹ️ - Retry analysis when service is available
[12:51:08 PM] ℹ️ 📊 SUMMARY FEEDBACK:
[12:51:08 PM] ℹ️ • AI analysis service is temporarily overloaded
[12:51:08 PM] ℹ️ • Using fallback analysis with neutral scores
[12:51:08 PM] ℹ️ • Error: [GoogleGenerativeAI Error]: Error fetching from https://generativelanguage.googleapis.com/v1beta/models/gemini-2.5-pro:generateContent: [429 Too Many Requests] You exceeded your current quota, please check your plan and billing details. For more information on this error, head to: https://ai.google.dev/gemini-api/docs/rate-limits.
* Quota exceeded for metric: generativelanguage.googleapis.com/generate_content_free_tier_requests, limit: 2
Please retry in 51.171089048s. [{"@type":"type.googleapis.com/google.rpc.QuotaFailure","violations":[{"quotaMetric":"generativelanguage.googleapis.com/generate_content_free_tier_requests","quotaId":"GenerateRequestsPerMinutePerProjectPerModel-FreeTier","quotaDimensions":{"model":"gemini-2.5-pro","location":"global"},"quotaValue":"2"}]},{"@type":"type.googleapis.com/google.rpc.Help","links":[{"description":"Learn more about Gemini API quotas","url":"https://ai.google.dev/gemini-api/docs/rate-limits"}]},{"@type":"type.googleapis.com/google.rpc.RetryInfo","retryDelay":"51s"}]
[12:51:08 PM] ❌ ❌ GEMINI AI VALIDATION FAILED: 70% relevance score (required: 80%)
[12:51:08 PM] ℹ️ Continuing execution due to soft assertion...